[INFO] cloning repository https://github.com/computer-whisperer/proving_bootstrap_test
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/computer-whisperer/proving_bootstrap_test" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcomputer-whisperer%2Fproving_bootstrap_test", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcomputer-whisperer%2Fproving_bootstrap_test'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] b6620a517cc6484eea33dd17c17b34814156a8da
[INFO] testing computer-whisperer/proving_bootstrap_test against try#28f95933dc0727396c07b48d0a8cb5211b7fb472 for pr-154065-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fcomputer-whisperer%2Fproving_bootstrap_test" "/workspace/builds/worker-6-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-6-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/computer-whisperer/proving_bootstrap_test
[INFO] finished tweaking git repo https://github.com/computer-whisperer/proving_bootstrap_test
[INFO] tweaked toml for git repo https://github.com/computer-whisperer/proving_bootstrap_test written to /workspace/builds/worker-6-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/computer-whisperer/proving_bootstrap_test on toolchain 28f95933dc0727396c07b48d0a8cb5211b7fb472
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/computer-whisperer/proving_bootstrap_test already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 36cc20714ea94ecd82591bb686ff21e095a3274781587ce8e8ffea56eae1d990
[INFO] running `Command { std: "docker" "start" "-a" "36cc20714ea94ecd82591bb686ff21e095a3274781587ce8e8ffea56eae1d990", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "36cc20714ea94ecd82591bb686ff21e095a3274781587ce8e8ffea56eae1d990", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "36cc20714ea94ecd82591bb686ff21e095a3274781587ce8e8ffea56eae1d990", kill_on_drop: false }`
[INFO] [stdout] 36cc20714ea94ecd82591bb686ff21e095a3274781587ce8e8ffea56eae1d990
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 522492e1872df026a0a3b9b9f408375576567af7a1baccfbe7867834613e19ca
[INFO] running `Command { std: "docker" "start" "-a" "522492e1872df026a0a3b9b9f408375576567af7a1baccfbe7867834613e19ca", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling proving-bootstrap v0.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.39s
[INFO] running `Command { std: "docker" "inspect" "522492e1872df026a0a3b9b9f408375576567af7a1baccfbe7867834613e19ca", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "522492e1872df026a0a3b9b9f408375576567af7a1baccfbe7867834613e19ca", kill_on_drop: false }`
[INFO] [stdout] 522492e1872df026a0a3b9b9f408375576567af7a1baccfbe7867834613e19ca
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] e2f03c719e2b4ce2b7fd231981fd00bad596758441a4b68d6151be8ce3b11c51
[INFO] running `Command { std: "docker" "start" "-a" "e2f03c719e2b4ce2b7fd231981fd00bad596758441a4b68d6151be8ce3b11c51", kill_on_drop: false }`
[INFO] [stderr]    Compiling proving-bootstrap v0.0.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 7.98s
[INFO] running `Command { std: "docker" "inspect" "e2f03c719e2b4ce2b7fd231981fd00bad596758441a4b68d6151be8ce3b11c51", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "e2f03c719e2b4ce2b7fd231981fd00bad596758441a4b68d6151be8ce3b11c51", kill_on_drop: false }`
[INFO] [stdout] e2f03c719e2b4ce2b7fd231981fd00bad596758441a4b68d6151be8ce3b11c51
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-6-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+28f95933dc0727396c07b48d0a8cb5211b7fb472" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 8e671ccd4a23c5ed955780e0ab11240b3b0bcd74bfac599d060f5da8f73af3de
[INFO] running `Command { std: "docker" "start" "-a" "8e671ccd4a23c5ed955780e0ab11240b3b0bcd74bfac599d060f5da8f73af3de", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/proving_bootstrap-a362cec8883a0d31)
[INFO] [stdout] 
[INFO] [stderr]      Running tests/m0_object_language.rs (/opt/rustwide/target/debug/deps/m0_object_language-39ffb5fab3dc0785)
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test rejects_mutual_recursion ... ok
[INFO] [stdout] test evaluates_concrete_terms ... ok
[INFO] [stdout] test concrete_unit_test_passes_by_evaluation ... ok
[INFO] [stdout] test module_is_admitted ... ok
[INFO] [stderr]      Running tests/m1_proof_kernel.rs (/opt/rustwide/target/debug/deps/m1_proof_kernel-56eaf2307b79c3ec)
[INFO] [stdout] test ast_round_trips_through_json ... ok
[INFO] [stdout] test rejects_non_structural_recursion ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test proves_add_zero ... ok
[INFO] [stderr]      Running tests/m2_reverse_equivalence.rs (/opt/rustwide/target/debug/deps/m2_reverse_equivalence-2e041cdb28bacd1b)
[INFO] [stdout] test proves_append_nil ... ok
[INFO] [stdout] test rejects_missing_induction_case ... ok
[INFO] [stdout] test rejects_bad_rewrite ... ok
[INFO] [stdout] test rejects_refl_without_reduction ... ok
[INFO] [stdout] test proof_round_trips_through_json ... ok
[INFO] [stdout] test theory_checks_both_in_order ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test module_is_admitted ... ok
[INFO] [stdout] test fast_rev_fails_without_its_lemmas ... ok
[INFO] [stdout] test proves_fast_equals_rev_end_to_end ... ok
[INFO] [stdout] test whole_chain_round_trips_through_json ... ok
[INFO] [stdout] test proves_lemmas_individually ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/m3.rs (/opt/rustwide/target/debug/deps/m3-a049fd7455ac7f4a)
[INFO] [stdout] 
[INFO] [stdout] running 44 tests
[INFO] [stdout] test common::arith::proves_lt_imp_neq ... ok
[INFO] [stdout] test common::arith::proves_basic_arithmetic ... ok
[INFO] [stdout] test common::arith::search_cannot_reach_conditional_arithmetic ... ignored, slow (~2min): exhausts the search budget to confirm it cannot generate RewriteWith
[INFO] [stdout] test common::arith::proves_order_toolkit ... ok
[INFO] [stdout] test common::framing::address_disequality_is_provable ... ok
[INFO] [stdout] test common::arith::proves_congruence_lemmas ... ok
[INFO] [stdout] test common::framing::case_on_over_expression ... ok
[INFO] [stdout] test common::framing::ex_falso_after_simplifying_premise ... ok
[INFO] [stdout] test common::framing::ex_falso_from_constructor_clash ... ok
[INFO] [stdout] test common::framing::induction_over_memory_works ... ok
[INFO] [stdout] test common::framing::plain_rewrite_rejects_conditional_lemma ... ok
[INFO] [stdout] test common::framing::inductive_conditional_lemma_with_conditional_ih ... ok
[INFO] [stdout] test common::framing::proves_conditional_framing_lemma ... ok
[INFO] [stdout] test common::framing::proves_memory_framing_lemmas ... ok
[INFO] [stdout] test common::framing::rewrite_with_checks_premise_count ... ok
[INFO] [stdout] test common::framing::proves_swap_framing ... ok
[INFO] [stdout] test common::framing::uses_conditional_lemma_via_rewrite_with ... ok
[INFO] [stdout] test common::model::module_is_admitted ... ok
[INFO] [stdout] test common::reverse::concrete_in_place_reverse_executes ... ok
[INFO] [stdout] test common::reverse::proves_reverse_eq_rev_universal ... ignored, the capstone: in-place reverse = rev for universal n (slow: builds the full theory)
[INFO] [stdout] test common::reverse::proves_reverse_for_fixed_sizes_general_memory ... ok
[INFO] [stdout] test common::framing::search_finds_swap_framing ... ok
[INFO] [stdout] test common::spec::proves_per_position_spec ... ignored, the full universal-n per-position invariant (slow: builds the toolkit + searches Z)
[INFO] [stdout] test common::spec::proves_spec_false_branch ... ignored, checks the spec S-case False branch in isolation against its sub-sequent
[INFO] [stdout] test common::spec::proves_spec_true_branch ... ignored, checks the spec S-case True branch in isolation against its sub-sequent
[INFO] [stdout] test common::wasm::probe_simp_fuel_growth ... ignored, regression guard: simp(run(loop_top, S^N rest)) time stays ~flat in N (was exponential before simp memoization)
[INFO] [stdout] test common::wasm::proves_enter_loop ... ok
[INFO] [stdout] test common::reverse::proves_task4_list_lemmas ... ok
[INFO] [stdout] test common::wasm::proves_loop_step_exit ... ok
[INFO] [stdout] test common::wasm::proves_sim ... ignored, the wasm simulation: run(loop_top) ≡ rev_loop for universal j (slow: builds the searched toolkit)
[INFO] [stdout] test common::wasm::proves_wasm_reverse_correct ... ignored, THE M4 capstone: wasm reverse = rev for universal n (slow: builds the full M3 theory + sim)
[INFO] [stdout] test common::wasm::trace_wasm_reverse_n4 ... ignored, authoring aid: prints the VM trace of the n=4 reverse (run with --nocapture)
[INFO] [stdout] test common::wasm::trace_wasm_reverse_symbolic ... ignored, authoring aid: VM trace with symbolic memory (what the proof sees)
[INFO] [stdout] test common::wasm::wasm_module_is_admitted ... ok
[INFO] [stdout] test common::arith::search_rediscovers_arithmetic_proofs ... ok
[INFO] [stdout] test common::wasm::proves_loop_step_continue ... ok
[INFO] [stdout] test common::wasm::wasm_reverse_executes ... ok
[INFO] [stdout] test common::arith::builds_reverse_toolkit_theory has been running for over 60 seconds
[INFO] [stdout] test common::arith::proves_arith_building_blocks has been running for over 60 seconds
[INFO] [stdout] test common::arith::proves_mirror_induction_lemmas has been running for over 60 seconds
[INFO] [stdout] test common::arith::proves_mirror_lemmas has been running for over 60 seconds
[INFO] [stdout] test common::arith::search_builds_arith_and_mirror_theory has been running for over 60 seconds
[INFO] [stdout] test common::wasm::wasm_refines_rev_loop_for_fixed_sizes has been running for over 60 seconds
[INFO] [stdout] test common::wasm::wasm_refines_rev_loop_for_fixed_sizes ... ok
[INFO] [stdout] test common::wasm::wasm_reverse_equals_rev_for_fixed_sizes has been running for over 60 seconds
[INFO] [stdout] test common::wasm::wasm_reverse_equals_rev_for_fixed_sizes ... ok
[ERROR] error running command: no output for 300 seconds
[INFO] running `Command { std: "docker" "inspect" "8e671ccd4a23c5ed955780e0ab11240b3b0bcd74bfac599d060f5da8f73af3de", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "8e671ccd4a23c5ed955780e0ab11240b3b0bcd74bfac599d060f5da8f73af3de", kill_on_drop: false }`
[INFO] [stdout] 8e671ccd4a23c5ed955780e0ab11240b3b0bcd74bfac599d060f5da8f73af3de
